[INFO] cloning repository https://github.com/a-givertzman/rust-sal-sync
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/a-givertzman/rust-sal-sync" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1e2fea3ae9de0dcd87ebdf0924cb4ce47e243b8b
[INFO] testing a-givertzman/rust-sal-sync against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fa-givertzman%2Frust-sal-sync" "/workspace/builds/worker-3-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/a-givertzman/rust-sal-sync
[INFO] finished tweaking git repo https://github.com/a-givertzman/rust-sal-sync
[INFO] tweaked toml for git repo https://github.com/a-givertzman/rust-sal-sync written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/a-givertzman/rust-sal-sync on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/a-givertzman/rust-sal-sync already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-api-tools.git`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-debuging.git`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-sal-core.git`
[INFO] [stderr]     Updating git repository `https://github.com/a-givertzman/rust-testing.git`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded crossbeam-skiplist v0.1.3
[INFO] [stderr]   Downloaded concat-string v1.0.1
[INFO] [stderr]   Downloaded concat-in-place v1.1.0
[INFO] [stderr]   Downloaded lexical v7.0.4
[INFO] [stderr]   Downloaded postgres v0.19.10
[INFO] [stderr]   Downloaded postgres-protocol v0.6.8
[INFO] [stderr]   Downloaded coco v0.3.4
[INFO] [stderr]   Downloaded postgres-types v0.2.9
[INFO] [stderr]   Downloaded tokio-postgres v0.7.13
[INFO] [stderr]   Downloaded kanal v0.1.1
[INFO] [stderr]   Downloaded jiff-static v0.2.15
[INFO] [stderr]   Downloaded jiff v0.2.15
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 0aa1d91fa66f0ebea948c03aec9b78d060e745275ee2a8d01ac6273c1342b5b8
[INFO] running `Command { std: "docker" "start" "-a" "0aa1d91fa66f0ebea948c03aec9b78d060e745275ee2a8d01ac6273c1342b5b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0aa1d91fa66f0ebea948c03aec9b78d060e745275ee2a8d01ac6273c1342b5b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0aa1d91fa66f0ebea948c03aec9b78d060e745275ee2a8d01ac6273c1342b5b8", kill_on_drop: false }`
[INFO] [stdout] 0aa1d91fa66f0ebea948c03aec9b78d060e745275ee2a8d01ac6273c1342b5b8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 4cb47f504cb3e21e9fd561bcdf9eb3c60ac39356f07ca30e010244db1efae082
[INFO] running `Command { std: "docker" "start" "-a" "4cb47f504cb3e21e9fd561bcdf9eb3c60ac39356f07ca30e010244db1efae082", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.173
[INFO] [stderr]    Compiling cfg-if v1.0.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.95
[INFO] [stderr]    Compiling unicode-ident v1.0.18
[INFO] [stderr]    Compiling memchr v2.7.5
[INFO] [stderr]    Compiling zerocopy v0.8.25
[INFO] [stderr]    Compiling typenum v1.18.0
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling log v0.4.27
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling lock_api v0.4.13
[INFO] [stderr]    Compiling tinyvec v1.9.0
[INFO] [stderr]    Compiling anstyle v1.0.11
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling anstyle-query v1.1.3
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling syn v2.0.103
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling lexical-util v1.0.6
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling unicode-normalization v0.1.24
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling block-buffer v0.10.4
[INFO] [stderr]    Compiling anstream v0.6.19
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling ryu v1.0.20
[INFO] [stderr]    Compiling itoa v1.0.15
[INFO] [stderr]    Compiling jiff v0.2.15
[INFO] [stderr]    Compiling unicode-properties v0.1.3
[INFO] [stderr]    Compiling serde_json v1.0.140
[INFO] [stderr]    Compiling unicode-bidi v0.3.18
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.9.1
[INFO] [stderr]    Compiling stringprep v0.1.5
[INFO] [stderr]    Compiling hmac v0.12.1
[INFO] [stderr]    Compiling md-5 v0.10.6
[INFO] [stderr]    Compiling mio v1.0.4
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling fallible-iterator v0.2.0
[INFO] [stderr]    Compiling siphasher v1.0.1
[INFO] [stderr]    Compiling iana-time-zone v0.1.63
[INFO] [stderr]    Compiling postgres-protocol v0.6.8
[INFO] [stderr]    Compiling tokio v1.45.1
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling slab v0.4.10
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling env_filter v0.1.3
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling lexical-parse-integer v1.0.5
[INFO] [stderr]    Compiling lexical-write-integer v1.0.5
[INFO] [stderr]    Compiling virtue v0.0.18
[INFO] [stderr]    Compiling whoami v1.6.0
[INFO] [stderr]    Compiling hashbrown v0.15.4
[INFO] [stderr]    Compiling rustversion v1.0.21
[INFO] [stderr]    Compiling lexical-write-float v1.0.5
[INFO] [stderr]    Compiling bincode_derive v2.0.1
[INFO] [stderr]    Compiling lexical-parse-float v1.0.5
[INFO] [stderr]    Compiling unty v0.0.4
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling scopeguard v0.3.3
[INFO] [stderr]    Compiling unsafe-libyaml v0.2.11
[INFO] [stderr]    Compiling lexical-core v1.0.5
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling env_logger v0.11.8
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling async-trait v0.1.88
[INFO] [stderr]    Compiling debugging v0.0.1 (https://github.com/a-givertzman/rust-debuging.git?tag=0.0.1#18c3318a)
[INFO] [stderr]    Compiling testing v0.0.2 (https://github.com/a-givertzman/rust-testing.git?tag=0.0.2#b810ec56)
[INFO] [stderr]    Compiling testing v0.1.1 (https://github.com/a-givertzman/rust-testing.git?tag=0.1.2#f18b3370)
[INFO] [stderr]    Compiling bincode v2.0.1
[INFO] [stderr]    Compiling sal-core-macros v0.1.0 (https://github.com/a-givertzman/rust-sal-core.git?tag=0.1.0#d1344384)
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling dashmap v6.1.0
[INFO] [stderr]    Compiling coco v0.3.4
[INFO] [stderr]    Compiling strum_macros v0.27.1
[INFO] [stderr]    Compiling tokio-util v0.7.15
[INFO] [stderr]    Compiling lexical v7.0.4
[INFO] [stderr]    Compiling hashers v1.0.1
[INFO] [stderr]    Compiling sal-core v0.1.0 (https://github.com/a-givertzman/rust-sal-core.git?tag=0.1.0#d1344384)
[INFO] [stderr]    Compiling crossbeam-skiplist v0.1.3
[INFO] [stderr]    Compiling kanal v0.1.1
[INFO] [stderr]    Compiling concat-string v1.0.1
[INFO] [stderr]    Compiling strum v0.27.1
[INFO] [stderr]    Compiling concat-in-place v1.1.0
[INFO] [stderr]    Compiling indexmap v2.9.0
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling postgres-types v0.2.9
[INFO] [stderr]    Compiling tokio-postgres v0.7.13
[INFO] [stderr]    Compiling postgres v0.19.10
[INFO] [stderr]    Compiling api-tools v0.0.6 (https://github.com/a-givertzman/rust-api-tools.git?tag=0.1.1#e7c72798)
[INFO] [stderr]    Compiling sal-sync v0.3.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unnecessary parentheses around type
[INFO] [stdout]   --> src/sync/handles.rs:31:26
[INFO] [stdout]    |
[INFO] [stdout] 31 |         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout]    |                          ^              ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] help: remove these parentheses
[INFO] [stdout]    |
[INFO] [stdout] 31 -         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = Stack::new();
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `filter` is never read
[INFO] [stdout]   --> src/services/entity/point/point_config.rs:89:21
[INFO] [stdout]    |
[INFO] [stdout] 89 |                     filter.factor = None
[INFO] [stdout]    |                     ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = help: maybe it is overwritten before being read?
[INFO] [stdout]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 25s
[INFO] running `Command { std: "docker" "inspect" "4cb47f504cb3e21e9fd561bcdf9eb3c60ac39356f07ca30e010244db1efae082", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4cb47f504cb3e21e9fd561bcdf9eb3c60ac39356f07ca30e010244db1efae082", kill_on_drop: false }`
[INFO] [stdout] 4cb47f504cb3e21e9fd561bcdf9eb3c60ac39356f07ca30e010244db1efae082
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48f3bcd37e6a424d0546c9b8a4de66c2b55adc60139df0e9a5b7477ddd60022c
[INFO] running `Command { std: "docker" "start" "-a" "48f3bcd37e6a424d0546c9b8a4de66c2b55adc60139df0e9a5b7477ddd60022c", kill_on_drop: false }`
[INFO] [stderr]    Compiling sal-sync v0.3.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unnecessary parentheses around type
[INFO] [stdout]   --> src/sync/handles.rs:31:26
[INFO] [stdout]    |
[INFO] [stdout] 31 |         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout]    |                          ^              ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] help: remove these parentheses
[INFO] [stdout]    |
[INFO] [stdout] 31 -         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = Stack::new();
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `filter` is never read
[INFO] [stdout]   --> src/services/entity/point/point_config.rs:89:21
[INFO] [stdout]    |
[INFO] [stdout] 89 |                     filter.factor = None
[INFO] [stdout]    |                     ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = help: maybe it is overwritten before being read?
[INFO] [stdout]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unnecessary parentheses around type
[INFO] [stdout]   --> src/sync/handles.rs:31:26
[INFO] [stdout]    |
[INFO] [stdout] 31 |         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout]    |                          ^              ^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] help: remove these parentheses
[INFO] [stdout]    |
[INFO] [stdout] 31 -         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stdout] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = Stack::new();
[INFO] [stdout]    |
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `filter` is never read
[INFO] [stdout]   --> src/services/entity/point/point_config.rs:89:21
[INFO] [stdout]    |
[INFO] [stdout] 89 |                     filter.factor = None
[INFO] [stdout]    |                     ^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = help: maybe it is overwritten before being read?
[INFO] [stdout]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 11.24s
[INFO] running `Command { std: "docker" "inspect" "48f3bcd37e6a424d0546c9b8a4de66c2b55adc60139df0e9a5b7477ddd60022c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48f3bcd37e6a424d0546c9b8a4de66c2b55adc60139df0e9a5b7477ddd60022c", kill_on_drop: false }`
[INFO] [stdout] 48f3bcd37e6a424d0546c9b8a4de66c2b55adc60139df0e9a5b7477ddd60022c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] be67d87a19c467c625b6f017a61a027fd6942a7a529a8860cc56cf2d3dbc4546
[INFO] running `Command { std: "docker" "start" "-a" "be67d87a19c467c625b6f017a61a027fd6942a7a529a8860cc56cf2d3dbc4546", kill_on_drop: false }`
[INFO] [stderr] warning: unnecessary parentheses around type
[INFO] [stderr]   --> src/sync/handles.rs:31:26
[INFO] [stderr]    |
[INFO] [stderr] 31 |         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stderr]    |                          ^              ^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_parens)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] help: remove these parentheses
[INFO] [stderr]    |
[INFO] [stderr] 31 -         let h: Stack<Box<(dyn WaitBox<T>)>> = Stack::new();
[INFO] [stderr] 31 +         let h: Stack<Box<dyn WaitBox<T> >> = Stack::new();
[INFO] [stderr]    |
[INFO] [stderr] 
[INFO] [stderr] warning: value assigned to `filter` is never read
[INFO] [stderr]   --> src/services/entity/point/point_config.rs:89:21
[INFO] [stderr]    |
[INFO] [stderr] 89 |                     filter.factor = None
[INFO] [stderr]    |                     ^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = help: maybe it is overwritten before being read?
[INFO] [stderr]    = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `sal-sync` (lib) generated 2 warnings (run `cargo fix --lib -p sal-sync` to apply 1 suggestion)
[INFO] [stderr] warning: `sal-sync` (lib test) generated 2 warnings (2 duplicates)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.26s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sal_sync-e9a0283724098dd3)
[INFO] [stdout] 
[INFO] [stdout] running 107 tests
[INFO] [stdout] test tests::unit::kernel::state::change_notify_test::change_notify::add ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_start_step_back ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_stot_step_back ... ok
[INFO] [stdout] test tests::unit::math::aprox_eq_test::tests::test_f32 ... ok
[INFO] [stdout] test tests::unit::math::aprox_eq_test::tests::test_f64 ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_get_test::config_tree_get::valid ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::as_type ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_changed_test::tests::test_state_empty_steps ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_test::config_tree::valid ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_changed_test::tests::test_state ... ok
[INFO] [stdout] test tests::unit::kernel::state::switch_state_test::tests::test_single ... ok
[INFO] [stdout] test tests::unit::services::conf::diag_keywd_test::diag_keywd::variants ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_tree_keys_test::conf_tree::keys ... ok
[INFO] [stdout] test tests::unit::services::conf::diag_keywd_test::diag_keywd::from_str ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::as_str ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::default ... ok
[INFO] [stdout] test tests::unit::services::entity::name_test::name::into_string ... ok
[INFO] [stdout] test tests::unit::services::entity::name_test::name::join ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::cot_test::cot::contains ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::serialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::empty ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_address_test::point_config_address::serialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::serialize ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_duration_test::test_create_valid ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::serialize ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_type_test::point_config_type::to_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::abs ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::bitand ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::bitor ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::div ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::add ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::mul ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_bool ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_int ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_double ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::pow ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::sub ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_bool ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_double ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_int ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::new_real ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_real ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_hlr_test::point_hlr::to_string ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::cot ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::dest ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::name ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::deserialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::status ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::serialize_json ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::timestamp ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::tx_id ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::type_ ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_test::point::value ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_tx_id_test::point_tx_id::test_add ... ok
[INFO] [stdout] test tests::unit::services::entity::point::point_config_test::point_config::deserialize_yaml ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_performance_test::multi_queue::match_performance ... ignored, Performance test
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_performance_test::multi_queue::performance ... ignored, MultiQueue Performance test
[INFO] [stdout] test tests::unit::services::conf::conf_duration_test::test_create_invalid ... ok
[INFO] [stdout] test tests::unit::services::future::future_test::future::then ... ok
[INFO] [stdout] test tests::unit::services::conf::conf_keyword_test::conf_keywd::from_str ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_read_write_test::multi_queue::read_write ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_read_test::multi_queue::static_read ... ok
[INFO] [stdout] test tests::unit::services::retain::retain_point_id::map_update_or_insert_test::map_update_or_insert::test ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::queue ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::service ... ok
[INFO] [stdout] test tests::unit::services::service::link_name_test::link_name::split ... ok
[INFO] [stdout] test tests::unit::services::future::future_spawn_test::future::spawn ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::basic ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_test::multi_queue::run_thread ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_test::multi_queue::run_scheduler ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::extend_subscription - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::subscribe - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::get_link - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::gi - should panic ... ok
[INFO] [stdout] test tests::unit::services::service::service_test::trait_service::unsubscribe - should panic ... ok
[INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_read_test::tests::test_fn_config_read_valid ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscription_ctiteria_test::subscription_criteria::destination ... ok
[INFO] [stdout] test tests::unit::services::subscription::subscription_ctiteria_test::subscription_criteria::performance ... ignored, Performance test
[INFO] [stdout] test tests::unit::services::subscription::subscriptions_test::subscriptions::new ... ok
[INFO] [stdout] test tests::unit::services::service::service_cycle_test::service_cycle::basic ... FAILED
[INFO] [stdout] test tests::unit::services::subscription::conf_subscribe_test::conf_subscribe::new ... ok
[INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_point_test::tests::test_fn_config_point ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_add ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_bit_and ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_bit_or ... ok
[INFO] [stdout] test tests::unit::services::types::bool_test::bool::test_mul ... ok
[INFO] [stdout] test tests::unit::services::types::type_of_test::type_of::basic ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::invalid ... ok
[INFO] [stdout] test tests::unit::services::multi_queue::multi_queue_subscribe_test::multi_queue::subscribe_broadcast ... ok
[INFO] [stdout] test tests::unit::services::task::functions::conf::fn_conf_keyword_test::fn_conf_keywd::valid_options ... ok
[INFO] [stdout] test tests::unit::services::conf::fn_config::fn_config_new_test::tests::test_fn_config_new_valid ... ok
[INFO] [stdout] test tests::unit::thread_pool::scheduler_test::scheduler::single_capacity ... ok
[INFO] [stdout] test tests::unit::thread_pool::thread_pool_test::thread_pool::spawn ... ok
[INFO] [stdout] test tests::unit::thread_pool::thread_pool_test::thread_pool::single_capacity ... ok
[INFO] [stdout] test tests::unit::services::services::services_test::services::services_scheduler ... ok
[INFO] [stdout] test tests::unit::services::services::services_test::services::services_thread ... ok
[INFO] [stdout] test tests::unit::thread_pool::scheduler_test::scheduler::spawn ... ok
[INFO] [stdout] test tests::unit::sync::handles_test::handles::wait ... ok
[INFO] [stdout] test tests::unit::sync::kanal_channel_test::kanal_channel::iter ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::unit::services::service::service_cycle_test::service_cycle::basic stdout ----
[INFO] [stdout] 
[INFO] [stdout] test ServiceCycle
[INFO] [stdout] 
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m target cycle interval: 1 ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m length: 1
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m aproxEq digits: 3
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m detecting load range...
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load range 1...2772
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [32mINFO [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m elapsed for max load: 1.40424ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:28Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1990
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.051566455
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 50.629945ms, elapsed 51.629945ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.051648585
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 918
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00047441
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.036697165
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.036697165
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 496
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000238631
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009904319
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.009904319
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2049
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0010446
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 63.36µs, elapsed 1.06336ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001080451
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1638
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.026879717
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 25.939746ms, elapsed 26.939746ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.026959826
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1357
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000716471
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.019522348
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.019522348
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 588
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.015331159
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 14.390339ms, elapsed 15.390339ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015409869
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1890
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00092548
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015756808
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.015756808
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1716
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00084217
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.035947666
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.035947666
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 379
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00017918
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011877949
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.011877949
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2724
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00136648
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 390.45µs, elapsed 1.39045ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00140773
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1545
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.042795235
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 41.826905ms, elapsed 42.826905ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.042845955
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 145
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 7.227e-5
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106792
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2419
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.047181875
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 46.225895ms, elapsed 47.225895ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.047244885
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2648
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.052901474
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 51.957124ms, elapsed 52.957124ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.052976804
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1987
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.008056399
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.086579ms, elapsed 8.086579ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008105129
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 316
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.028118737
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 27.189277ms, elapsed 28.189277ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.028209117
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2708
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.038363516
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 37.390036ms, elapsed 38.390036ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.038406276
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2299
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.016886488
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 15.915588ms, elapsed 16.915588ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016938808
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 800
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00037968
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.024111267
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.024111267
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2167
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001104399
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 131.719µs, elapsed 1.131719ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001150859
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1180
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.026832497
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 25.887347ms, elapsed 26.887347ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.026908107
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1750
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.025876958
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 24.929458ms, elapsed 25.929458ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025947928
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1416
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.011918798
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 10.975398ms, elapsed 11.975398ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011992498
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2282
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.0010477
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 61.54µs, elapsed 1.06154ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107448
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 178
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.036353456
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 35.406446ms, elapsed 36.406446ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.036424596
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2638
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.020400118
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 19.458888ms, elapsed 20.458888ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.020481408
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1748
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00086836
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.026681607
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.026681607
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 458
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023594
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009708509
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.009708509
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1241
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00061423
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.012940119
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.012940119
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 922
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00046216
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.022520547
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.022520547
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 572
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00028539
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00290807
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00290807
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 900
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00044056
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008277939
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.008277939
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1447
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00075493
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.020567818
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.020567818
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1327
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00063057
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008924449
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.008924449
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2270
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.024120687
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 23.175517ms, elapsed 24.175517ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.024193757
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2532
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00124963
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 270.11µs, elapsed 1.27011ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00128446
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2683
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.044762735
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 43.850315ms, elapsed 44.850315ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.044871255
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1646
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.031830267
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 30.882477ms, elapsed 31.882477ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.031901967
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1210
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00059922
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016173978
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.016173978
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1840
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00091372
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.034476996
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.034476996
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 606
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00029359
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.007371569
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.007371569
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2658
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.046368355
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 45.394595ms, elapsed 46.394595ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.046417005
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1709
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00081854
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010888029
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.010888029
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 654
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00030334
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.017282248
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.017282248
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1137
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.019635188
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 18.665098ms, elapsed 19.665098ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.019682508
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1400
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00065817
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.023910718
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.023910718
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1814
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000901249
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.022931947
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.022931947
[INFO] [stdout] [90m[[0m2026-03-27T01:53:29Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2001
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.025037167
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 24.072947ms, elapsed 25.072947ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025092087
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 88
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 4.3049e-5
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009048908
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.009048908
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 892
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00044148
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.079725192
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.079725192
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2518
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.025230517
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 24.279307ms, elapsed 25.279307ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.025297847
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 745
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00036659
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.013585589
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.013585589
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 472
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000212729
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001454849
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1926
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.009054349
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 8.08042ms, elapsed 9.08042ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00909892
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 217
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000103349
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.016327228
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.016327228
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 16
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 9.25e-6
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001073639
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1300
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000619909
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.014876118
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.014876118
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2267
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00115386
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 176.79µs, elapsed 1.17679ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00119477
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1767
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.043695365
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 42.746775ms, elapsed 43.746775ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.043764915
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1743
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.022366788
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 21.403448ms, elapsed 22.403448ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.022420868
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1261
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00064976
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.012851318
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.012851318
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 590
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00027831
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008628669
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.008628669
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1848
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00093061
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.027846647
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.027846647
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1388
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00068974
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.009734609
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.009734609
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1859
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.011344259
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 10.369459ms, elapsed 11.369459ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011386659
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 503
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00024367
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.021036088
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.021036088
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 545
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00029031
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010622738
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.010622738
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 579
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00028364
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00896828
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00896828
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 419
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001609609
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 631.559µs, elapsed 1.631559ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00164714
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2527
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.001201709
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 216.779µs, elapsed 1.216779ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001230329
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1520
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00075439
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00507697
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00507697
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 805
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00039228
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00307266
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00307266
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2076
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00103797
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 54.55µs, elapsed 1.05455ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106897
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1788
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00091838
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.011366319
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.011366319
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2151
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.008591219
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.615999ms, elapsed 8.615999ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008632889
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2624
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00130627
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 327.94µs, elapsed 1.32794ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00134144
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2491
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.015243428
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 14.269478ms, elapsed 15.269478ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.015285478
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 735
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00036045
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00235917
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00235917
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1902
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00396352
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.986559ms, elapsed 3.986559ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003999949
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2627
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00842625
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.450869ms, elapsed 8.450869ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008466159
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1695
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.003596249
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.61866ms, elapsed 3.61866ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003637189
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 106
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 5.221e-5
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00612909
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00612909
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2486
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00127772
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 298.869µs, elapsed 1.298869ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001314971
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 524
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00026268
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.010004929
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.010004929
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 352
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00017234
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00159055
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00159055
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 456
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00022593
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00207283
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00207283
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 864
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00040836
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.005714099
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.005714099
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 462
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023438
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00106631
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 712
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00034894
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00187214
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00187214
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1494
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00074796
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.004922429
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.004922429
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 697
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.000352019
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.002653169
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.002653169
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 891
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00047102
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00341825
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.00341825
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 474
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00023324
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.001830919
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): targetInSecs: 0.001 != cycleElapsed 0.001830919
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2733
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.008646648
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 7.671279ms, elapsed 8.671279ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.008693108
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2590
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.003034919
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 2.060539ms, elapsed 3.060539ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.003077669
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 1097
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.00712694
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 6.149569ms, elapsed 7.149569ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.007162689
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2438
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.006785209
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 5.809759ms, elapsed 6.809759ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.006825289
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 182
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 9.428e-5
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00107048
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m load: 2246
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m math done in: 0.005482749
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [1m[31mERROR[0m sal_sync::services::service::service_cycle[90m][0m service_cycle_test/ServiceCycle.wait | exceeded 1ms by 4.50724ms, elapsed 5.50724ms
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [34mDEBUG[0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m cycle done in: 0.00553194
[INFO] [stdout] [90m[[0m2026-03-27T01:53:30Z [33mWARN [0m sal_sync::tests::unit::services::service::service_cycle_test::service_cycle[90m][0m values must be aprox equals (3 digits): mathElapsed: 0.005482749 != cycleElapsed 0.00553194
[INFO] [stdout] 
[INFO] [stdout] thread 'tests::unit::services::service::service_cycle_test::service_cycle::basic' (258) panicked at src/tests/unit/services/service/service_cycle_test.rs:101:13:
[INFO] [stdout] to much errors (49), a few errors will be ok, but not more then 5% (20) of test cycles
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0: __rustc::rust_begin_unwind
[INFO] [stdout]              at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/std/src/panicking.rs:689:5
[INFO] [stdout]    1: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/panicking.rs:80:14
[INFO] [stdout]    2: sal_sync::tests::unit::services::service::service_cycle_test::service_cycle::basic
[INFO] [stdout]              at ./src/tests/unit/services/service/service_cycle_test.rs:101:13
[INFO] [stdout]    3: sal_sync::tests::unit::services::service::service_cycle_test::service_cycle::basic::{closure#0}
[INFO] [stdout]              at ./src/tests/unit/services/service/service_cycle_test.rs:27:15
[INFO] [stdout]    4: <sal_sync::tests::unit::services::service::service_cycle_test::service_cycle::basic::{closure#0} as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/ops/function.rs:250:5
[INFO] [stdout]    5: <fn() -> core::result::Result<(), alloc::string::String> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/1f7f8ea0721a3b1eb73e6c6d25cccb371434b320/library/core/src/ops/function.rs:250:5
[INFO] [stdout] note: Some details are omitted, run with `RUST_BACKTRACE=full` for a verbose backtrace.
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::unit::services::service::service_cycle_test::service_cycle::basic
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 103 passed; 1 failed; 3 ignored; 0 measured; 0 filtered out; finished in 7.36s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "be67d87a19c467c625b6f017a61a027fd6942a7a529a8860cc56cf2d3dbc4546", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "be67d87a19c467c625b6f017a61a027fd6942a7a529a8860cc56cf2d3dbc4546", kill_on_drop: false }`
[INFO] [stdout] be67d87a19c467c625b6f017a61a027fd6942a7a529a8860cc56cf2d3dbc4546
